11-19 20:36:14 D/ModuleListener: ModuleListener.testStarted(com.google.android.rvis.tvts.TvtsRvisTest#testHeavyUsageRvisLaunchLegacyAudioRecord) on SKBFXYZO0DTQ
11-19 20:36:14 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
11-19 20:36:14 D/DeviceHostUtils: apiLevel: 31
11-19 20:36:14 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, logcat, -c, -b, all] with timeout: 2m 0s
11-19 20:36:14 D/RunUtil: Running command null without timeout.
11-19 20:36:14 D/AppRunnerUtils: Checking service: com.google.android.tv.remote.service/.RemoteService
11-19 20:36:14 D/AppRunnerUtils: Running the following command: dumpsys activity service com.google.android.tv.remote.service/.RemoteService
11-19 20:36:14 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, dumpsys, activity, service, com.google.android.tv.remote.service/.RemoteService] with timeout: 2m 0s
11-19 20:36:15 I/AppRunnerUtils: Service PID = 31031
11-19 20:36:15 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
11-19 20:36:15 D/DeviceHostUtils: apiLevel: 31
11-19 20:36:15 I/TvtsRvisTest: KEYCODE_TO_VP_SHOWN	KEYCODE_TO_READING (In Milliseconds)
11-19 20:36:15 D/RunUtil: Running command null without timeout.
11-19 20:36:15 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, logcat, -c, -b, all] with timeout: 2m 0s
11-19 20:36:15 D/RunUtil: Running command null without timeout.
11-19 20:36:15 D/AppRunnerUtils: foregroundActivitySet(): [com.google.android.youtube.tv/com.google.android.apps.youtube.tv.activity.ShellActivity]
11-19 20:36:16 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.google.android.apps.youtube.tv.activity.ShellActivity.
11-19 20:36:16 D/AppRunnerUtils: waitUntilAppInForeground(): Checking activity: com.google.android.youtube.tv/com.google.android.apps.youtube.tv.activity.ShellActivity
11-19 20:36:16 D/RunUtil: operation failed, waiting for 500 ms
11-19 20:36:16 D/RunUtil: Running command null without timeout.
11-19 20:36:16 D/AppRunnerUtils: foregroundActivitySet(): [com.google.android.youtube.tv/com.google.android.apps.youtube.tv.activity.MainActivity]
11-19 20:36:17 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.google.android.apps.youtube.tv.activity.MainActivity.
11-19 20:36:17 D/AppRunnerUtils: waitUntilAppInForeground(): Checking activity: com.google.android.youtube.tv/com.google.android.apps.youtube.tv.activity.MainActivity
11-19 20:36:17 D/AppRunnerUtils: foregroundActivitySet(): [com.google.android.youtube.tv/com.google.android.apps.youtube.tv.activity.MainActivity]
11-19 20:36:18 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.google.android.apps.youtube.tv.activity.MainActivity.
11-19 20:36:33 D/ListInstrumentationParser: instrumentation:com.google.android.resolution.tvts/androidx.test.runner.AndroidJUnitRunner (target=com.google.android.resolution.tvts)
11-19 20:36:33 D/ListInstrumentationParser: instrumentation:com.google.android.rvis.tvts/androidx.test.runner.AndroidJUnitRunner (target=com.google.android.rvis.tvts)
11-19 20:36:33 D/ListInstrumentationParser: 
11-19 20:36:33 I/RemoteAndroidTestRunner: Running am instrument -w -r --abi armeabi-v7a  -e timeout_msec 20000 -e class 'com.google.android.rvis.tvts.TvtsRvisDeviceTest#sendAssistantKeycode' com.google.android.rvis.tvts/androidx.test.runner.AndroidJUnitRunner on foxconn-bfx_at100-SKBFXYZO0DTQ
11-19 20:36:35 I/BaseHostJUnit4Test: Test com.google.android.rvis.tvts.TvtsRvisDeviceTest#sendAssistantKeycode: PASSED
11-19 20:36:35 D/RunUtil: Running command [logcat -d | grep -E 'Read non-zero bytes'] without timeout.
11-19 20:36:36 I/TvtsRvisTest:                 142	               356
11-19 20:36:36 I/KeyCodeUtils: Sending keyevent KEYCODE_BACK - intValue: 4
11-19 20:36:36 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:36:37 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:36:37 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:36:37 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:36:37 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:36:37 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:36:37 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:36:38 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:36:38 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:36:38 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:36:38 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:36:38 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:36:39 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:36:39 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:36:39 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:36:39 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:36:39 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:36:40 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:36:40 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:36:40 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:36:40 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:36:40 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:36:41 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:36:41 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:36:41 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:36:41 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:36:41 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:36:42 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:36:42 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:36:42 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:36:42 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:36:42 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:36:43 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:36:43 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:36:43 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:36:43 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:36:43 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:36:44 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:36:44 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:36:44 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:36:44 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:36:44 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:36:45 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:36:45 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:36:45 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:36:45 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:36:45 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:36:46 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:36:46 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:36:46 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:36:46 E/TvtsRvisTest: AudioTrack was not paused or stopped
11-19 20:36:46 D/RunUtil: Running command null without timeout.
11-19 20:36:46 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, logcat, -c, -b, all] with timeout: 2m 0s
11-19 20:36:47 D/RunUtil: Running command null without timeout.
11-19 20:36:47 D/AppRunnerUtils: foregroundActivitySet(): [com.google.android.youtube.tv/com.google.android.apps.youtube.tv.activity.ShellActivity]
11-19 20:36:47 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.google.android.apps.youtube.tv.activity.ShellActivity.
11-19 20:36:47 D/AppRunnerUtils: waitUntilAppInForeground(): Checking activity: com.google.android.youtube.tv/com.google.android.apps.youtube.tv.activity.ShellActivity
11-19 20:36:47 D/RunUtil: operation failed, waiting for 500 ms
11-19 20:36:48 D/RunUtil: Running command null without timeout.
11-19 20:36:48 D/AppRunnerUtils: foregroundActivitySet(): [com.google.android.youtube.tv/com.google.android.apps.youtube.tv.activity.MainActivity]
11-19 20:36:49 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.google.android.apps.youtube.tv.activity.MainActivity.
11-19 20:36:49 D/AppRunnerUtils: waitUntilAppInForeground(): Checking activity: com.google.android.youtube.tv/com.google.android.apps.youtube.tv.activity.MainActivity
11-19 20:36:49 D/AppRunnerUtils: foregroundActivitySet(): [com.google.android.youtube.tv/com.google.android.apps.youtube.tv.activity.MainActivity]
11-19 20:36:49 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.google.android.apps.youtube.tv.activity.MainActivity.
11-19 20:37:04 D/ListInstrumentationParser: instrumentation:com.google.android.resolution.tvts/androidx.test.runner.AndroidJUnitRunner (target=com.google.android.resolution.tvts)
11-19 20:37:04 D/ListInstrumentationParser: instrumentation:com.google.android.rvis.tvts/androidx.test.runner.AndroidJUnitRunner (target=com.google.android.rvis.tvts)
11-19 20:37:04 D/ListInstrumentationParser: 
11-19 20:37:04 I/RemoteAndroidTestRunner: Running am instrument -w -r --abi armeabi-v7a  -e timeout_msec 20000 -e class 'com.google.android.rvis.tvts.TvtsRvisDeviceTest#sendAssistantKeycode' com.google.android.rvis.tvts/androidx.test.runner.AndroidJUnitRunner on foxconn-bfx_at100-SKBFXYZO0DTQ
11-19 20:37:07 I/BaseHostJUnit4Test: Test com.google.android.rvis.tvts.TvtsRvisDeviceTest#sendAssistantKeycode: PASSED
11-19 20:37:07 D/RunUtil: Running command [logcat -d | grep -E 'Read non-zero bytes'] without timeout.
11-19 20:37:08 I/TvtsRvisTest:                   9	               195
11-19 20:37:08 I/KeyCodeUtils: Sending keyevent KEYCODE_BACK - intValue: 4
11-19 20:37:08 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:37:08 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:37:08 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:37:09 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:37:09 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:37:09 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:37:09 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:37:09 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:37:10 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:37:10 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:37:10 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:37:10 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:37:10 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:37:11 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:37:11 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:37:11 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:37:11 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:37:11 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:37:12 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:37:12 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:37:12 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:37:12 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:37:12 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:37:13 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:37:13 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:37:13 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:37:13 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:37:13 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:37:14 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:37:14 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:37:14 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:37:14 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:37:14 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:37:15 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:37:15 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:37:15 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:37:15 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:37:15 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:37:16 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:37:16 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:37:16 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:37:16 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:37:16 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:37:17 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:37:17 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:37:17 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:37:17 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:37:18 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:37:18 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:37:18 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:37:18 E/TvtsRvisTest: AudioTrack was not paused or stopped
11-19 20:37:18 D/RunUtil: Running command null without timeout.
11-19 20:37:18 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, logcat, -c, -b, all] with timeout: 2m 0s
11-19 20:37:18 D/RunUtil: Running command null without timeout.
11-19 20:37:18 D/AppRunnerUtils: foregroundActivitySet(): [com.google.android.youtube.tv/com.google.android.apps.youtube.tv.activity.ShellActivity]
11-19 20:37:19 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.google.android.apps.youtube.tv.activity.ShellActivity.
11-19 20:37:19 D/AppRunnerUtils: waitUntilAppInForeground(): Checking activity: com.google.android.youtube.tv/com.google.android.apps.youtube.tv.activity.ShellActivity
11-19 20:37:19 D/RunUtil: operation failed, waiting for 500 ms
11-19 20:37:20 D/RunUtil: Running command null without timeout.
11-19 20:37:20 D/AppRunnerUtils: foregroundActivitySet(): [com.google.android.youtube.tv/com.google.android.apps.youtube.tv.activity.MainActivity]
11-19 20:37:20 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.google.android.apps.youtube.tv.activity.MainActivity.
11-19 20:37:20 D/AppRunnerUtils: waitUntilAppInForeground(): Checking activity: com.google.android.youtube.tv/com.google.android.apps.youtube.tv.activity.MainActivity
11-19 20:37:20 D/AppRunnerUtils: foregroundActivitySet(): [com.google.android.youtube.tv/com.google.android.apps.youtube.tv.activity.MainActivity]
11-19 20:37:21 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.google.android.apps.youtube.tv.activity.MainActivity.
11-19 20:37:36 D/ListInstrumentationParser: instrumentation:com.google.android.resolution.tvts/androidx.test.runner.AndroidJUnitRunner (target=com.google.android.resolution.tvts)
11-19 20:37:36 D/ListInstrumentationParser: instrumentation:com.google.android.rvis.tvts/androidx.test.runner.AndroidJUnitRunner (target=com.google.android.rvis.tvts)
11-19 20:37:36 D/ListInstrumentationParser: 
11-19 20:37:36 I/RemoteAndroidTestRunner: Running am instrument -w -r --abi armeabi-v7a  -e timeout_msec 20000 -e class 'com.google.android.rvis.tvts.TvtsRvisDeviceTest#sendAssistantKeycode' com.google.android.rvis.tvts/androidx.test.runner.AndroidJUnitRunner on foxconn-bfx_at100-SKBFXYZO0DTQ
11-19 20:37:39 I/BaseHostJUnit4Test: Test com.google.android.rvis.tvts.TvtsRvisDeviceTest#sendAssistantKeycode: PASSED
11-19 20:37:39 D/RunUtil: Running command [logcat -d | grep -E 'Read non-zero bytes'] without timeout.
11-19 20:37:39 I/TvtsRvisTest:                   8	               240
11-19 20:37:39 I/KeyCodeUtils: Sending keyevent KEYCODE_BACK - intValue: 4
11-19 20:37:40 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:37:40 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:37:40 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:37:40 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:37:41 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:37:41 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:37:41 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:37:41 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:37:41 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:37:42 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:37:42 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:37:42 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:37:42 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:37:42 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:37:43 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:37:43 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:37:43 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:37:43 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:37:43 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:37:44 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:37:44 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:37:44 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:37:44 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:37:45 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:37:45 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:37:45 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:37:45 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:37:45 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:37:46 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:37:46 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:37:46 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:37:46 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:37:46 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:37:47 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:37:47 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:37:47 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:37:47 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:37:47 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:37:48 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:37:48 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:37:48 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:37:48 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:37:48 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:37:49 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:37:49 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:37:49 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:37:49 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:37:50 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:37:50 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:37:50 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:37:50 E/TvtsRvisTest: AudioTrack was not paused or stopped
11-19 20:37:50 D/RunUtil: Running command null without timeout.
11-19 20:37:50 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, logcat, -c, -b, all] with timeout: 2m 0s
11-19 20:37:50 D/RunUtil: Running command null without timeout.
11-19 20:37:50 D/AppRunnerUtils: foregroundActivitySet(): [com.google.android.youtube.tv/com.google.android.apps.youtube.tv.activity.ShellActivity]
11-19 20:37:51 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.google.android.apps.youtube.tv.activity.ShellActivity.
11-19 20:37:51 D/AppRunnerUtils: waitUntilAppInForeground(): Checking activity: com.google.android.youtube.tv/com.google.android.apps.youtube.tv.activity.ShellActivity
11-19 20:37:51 D/RunUtil: operation failed, waiting for 500 ms
11-19 20:37:52 D/RunUtil: Running command null without timeout.
11-19 20:37:52 D/AppRunnerUtils: foregroundActivitySet(): [com.google.android.youtube.tv/com.google.android.apps.youtube.tv.activity.MainActivity]
11-19 20:37:52 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.google.android.apps.youtube.tv.activity.MainActivity.
11-19 20:37:52 D/AppRunnerUtils: waitUntilAppInForeground(): Checking activity: com.google.android.youtube.tv/com.google.android.apps.youtube.tv.activity.MainActivity
11-19 20:37:52 D/AppRunnerUtils: foregroundActivitySet(): [com.google.android.youtube.tv/com.google.android.apps.youtube.tv.activity.MainActivity]
11-19 20:37:53 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.google.android.apps.youtube.tv.activity.MainActivity.
11-19 20:38:08 D/ListInstrumentationParser: instrumentation:com.google.android.resolution.tvts/androidx.test.runner.AndroidJUnitRunner (target=com.google.android.resolution.tvts)
11-19 20:38:08 D/ListInstrumentationParser: instrumentation:com.google.android.rvis.tvts/androidx.test.runner.AndroidJUnitRunner (target=com.google.android.rvis.tvts)
11-19 20:38:08 D/ListInstrumentationParser: 
11-19 20:38:08 I/RemoteAndroidTestRunner: Running am instrument -w -r --abi armeabi-v7a  -e timeout_msec 20000 -e class 'com.google.android.rvis.tvts.TvtsRvisDeviceTest#sendAssistantKeycode' com.google.android.rvis.tvts/androidx.test.runner.AndroidJUnitRunner on foxconn-bfx_at100-SKBFXYZO0DTQ
11-19 20:38:11 I/BaseHostJUnit4Test: Test com.google.android.rvis.tvts.TvtsRvisDeviceTest#sendAssistantKeycode: PASSED
11-19 20:38:11 D/RunUtil: Running command [logcat -d | grep -E 'Read non-zero bytes'] without timeout.
11-19 20:38:11 I/TvtsRvisTest:                  14	               204
11-19 20:38:11 I/KeyCodeUtils: Sending keyevent KEYCODE_BACK - intValue: 4
11-19 20:38:12 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:38:12 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:38:12 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:38:12 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:38:13 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:38:13 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:38:13 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:38:13 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:38:13 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:38:14 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:38:14 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:38:14 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:38:14 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:38:14 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:38:15 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:38:15 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:38:15 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:38:15 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:38:15 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:38:16 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:38:16 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:38:16 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:38:16 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:38:16 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:38:17 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:38:17 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:38:17 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:38:17 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:38:17 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:38:18 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:38:18 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:38:18 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:38:18 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:38:18 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:38:19 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:38:19 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:38:19 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:38:19 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:38:19 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:38:19 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:38:20 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:38:20 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:38:20 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:38:20 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:38:21 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:38:21 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:38:21 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:38:21 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:38:21 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:38:22 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:38:22 E/TvtsRvisTest: AudioTrack was not paused or stopped
11-19 20:38:22 D/RunUtil: Running command null without timeout.
11-19 20:38:22 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, logcat, -c, -b, all] with timeout: 2m 0s
11-19 20:38:22 D/RunUtil: Running command null without timeout.
11-19 20:38:22 D/AppRunnerUtils: foregroundActivitySet(): [com.google.android.youtube.tv/com.google.android.apps.youtube.tv.activity.ShellActivity]
11-19 20:38:23 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.google.android.apps.youtube.tv.activity.ShellActivity.
11-19 20:38:23 D/AppRunnerUtils: waitUntilAppInForeground(): Checking activity: com.google.android.youtube.tv/com.google.android.apps.youtube.tv.activity.ShellActivity
11-19 20:38:23 D/RunUtil: operation failed, waiting for 500 ms
11-19 20:38:24 D/RunUtil: Running command null without timeout.
11-19 20:38:24 D/AppRunnerUtils: foregroundActivitySet(): [com.google.android.youtube.tv/com.google.android.apps.youtube.tv.activity.MainActivity]
11-19 20:38:24 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.google.android.apps.youtube.tv.activity.MainActivity.
11-19 20:38:24 D/AppRunnerUtils: waitUntilAppInForeground(): Checking activity: com.google.android.youtube.tv/com.google.android.apps.youtube.tv.activity.MainActivity
11-19 20:38:24 D/AppRunnerUtils: foregroundActivitySet(): [com.google.android.youtube.tv/com.google.android.apps.youtube.tv.activity.MainActivity]
11-19 20:38:25 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.google.android.apps.youtube.tv.activity.MainActivity.
11-19 20:38:40 D/ListInstrumentationParser: instrumentation:com.google.android.resolution.tvts/androidx.test.runner.AndroidJUnitRunner (target=com.google.android.resolution.tvts)
11-19 20:38:40 D/ListInstrumentationParser: instrumentation:com.google.android.rvis.tvts/androidx.test.runner.AndroidJUnitRunner (target=com.google.android.rvis.tvts)
11-19 20:38:40 D/ListInstrumentationParser: 
11-19 20:38:40 I/RemoteAndroidTestRunner: Running am instrument -w -r --abi armeabi-v7a  -e timeout_msec 20000 -e class 'com.google.android.rvis.tvts.TvtsRvisDeviceTest#sendAssistantKeycode' com.google.android.rvis.tvts/androidx.test.runner.AndroidJUnitRunner on foxconn-bfx_at100-SKBFXYZO0DTQ
11-19 20:38:42 I/BaseHostJUnit4Test: Test com.google.android.rvis.tvts.TvtsRvisDeviceTest#sendAssistantKeycode: PASSED
11-19 20:38:42 D/RunUtil: Running command [logcat -d | grep -E 'Read non-zero bytes'] without timeout.
11-19 20:38:43 I/TvtsRvisTest:                   8	               208
11-19 20:38:43 I/KeyCodeUtils: Sending keyevent KEYCODE_BACK - intValue: 4
11-19 20:38:43 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:38:44 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:38:44 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:38:44 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:38:44 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:38:45 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:38:45 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:38:45 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:38:45 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:38:45 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:38:46 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:38:46 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:38:46 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:38:46 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:38:46 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:38:47 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:38:47 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:38:47 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:38:47 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:38:48 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:38:48 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:38:48 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:38:48 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:38:48 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:38:49 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:38:49 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:38:49 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:38:49 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:38:49 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:38:50 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:38:50 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:38:50 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:38:50 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:38:50 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:38:51 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:38:51 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:38:51 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:38:51 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:38:51 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:38:52 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:38:52 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:38:52 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:38:52 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:38:53 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:38:53 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:38:53 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:38:53 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:38:53 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:38:54 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:38:54 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:38:54 E/TvtsRvisTest: AudioTrack was not paused or stopped
11-19 20:38:54 D/RunUtil: Running command null without timeout.
11-19 20:38:54 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, logcat, -c, -b, all] with timeout: 2m 0s
11-19 20:38:54 D/RunUtil: Running command null without timeout.
11-19 20:38:54 D/AppRunnerUtils: foregroundActivitySet(): [com.google.android.youtube.tv/com.google.android.apps.youtube.tv.activity.ShellActivity]
11-19 20:38:55 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.google.android.apps.youtube.tv.activity.ShellActivity.
11-19 20:38:55 D/AppRunnerUtils: waitUntilAppInForeground(): Checking activity: com.google.android.youtube.tv/com.google.android.apps.youtube.tv.activity.ShellActivity
11-19 20:38:55 D/RunUtil: operation failed, waiting for 500 ms
11-19 20:38:56 D/RunUtil: Running command null without timeout.
11-19 20:38:56 D/AppRunnerUtils: foregroundActivitySet(): [com.google.android.youtube.tv/com.google.android.apps.youtube.tv.activity.MainActivity]
11-19 20:38:56 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.google.android.apps.youtube.tv.activity.MainActivity.
11-19 20:38:56 D/AppRunnerUtils: waitUntilAppInForeground(): Checking activity: com.google.android.youtube.tv/com.google.android.apps.youtube.tv.activity.MainActivity
11-19 20:38:56 D/AppRunnerUtils: foregroundActivitySet(): [com.google.android.youtube.tv/com.google.android.apps.youtube.tv.activity.MainActivity]
11-19 20:38:57 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.google.android.apps.youtube.tv.activity.MainActivity.
11-19 20:39:12 D/ListInstrumentationParser: instrumentation:com.google.android.resolution.tvts/androidx.test.runner.AndroidJUnitRunner (target=com.google.android.resolution.tvts)
11-19 20:39:12 D/ListInstrumentationParser: instrumentation:com.google.android.rvis.tvts/androidx.test.runner.AndroidJUnitRunner (target=com.google.android.rvis.tvts)
11-19 20:39:12 D/ListInstrumentationParser: 
11-19 20:39:12 I/RemoteAndroidTestRunner: Running am instrument -w -r --abi armeabi-v7a  -e timeout_msec 20000 -e class 'com.google.android.rvis.tvts.TvtsRvisDeviceTest#sendAssistantKeycode' com.google.android.rvis.tvts/androidx.test.runner.AndroidJUnitRunner on foxconn-bfx_at100-SKBFXYZO0DTQ
11-19 20:39:15 I/BaseHostJUnit4Test: Test com.google.android.rvis.tvts.TvtsRvisDeviceTest#sendAssistantKeycode: PASSED
11-19 20:39:15 D/RunUtil: Running command [logcat -d | grep -E 'Read non-zero bytes'] without timeout.
11-19 20:39:15 I/TvtsRvisTest:                   9	               199
11-19 20:39:15 I/KeyCodeUtils: Sending keyevent KEYCODE_BACK - intValue: 4
11-19 20:39:16 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:39:16 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:39:16 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:39:16 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:39:17 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:39:17 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:39:17 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:39:17 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:39:17 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:39:18 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:39:18 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:39:18 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:39:18 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:39:18 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:39:19 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:39:19 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:39:19 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:39:19 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:39:19 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:39:19 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:39:20 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:39:20 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:39:20 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:39:20 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:39:20 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:39:21 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:39:21 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:39:21 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:39:21 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:39:22 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:39:22 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:39:22 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:39:22 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:39:22 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:39:23 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:39:23 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:39:23 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:39:23 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:39:23 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:39:24 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:39:24 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:39:24 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:39:24 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:39:24 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:39:25 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:39:25 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:39:25 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:39:25 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:39:25 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:39:26 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:39:26 E/TvtsRvisTest: AudioTrack was not paused or stopped
11-19 20:39:26 D/RunUtil: Running command null without timeout.
11-19 20:39:26 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, logcat, -c, -b, all] with timeout: 2m 0s
11-19 20:39:26 D/RunUtil: Running command null without timeout.
11-19 20:39:26 D/AppRunnerUtils: foregroundActivitySet(): [com.google.android.youtube.tv/com.google.android.apps.youtube.tv.activity.ShellActivity]
11-19 20:39:27 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.google.android.apps.youtube.tv.activity.ShellActivity.
11-19 20:39:27 D/AppRunnerUtils: waitUntilAppInForeground(): Checking activity: com.google.android.youtube.tv/com.google.android.apps.youtube.tv.activity.ShellActivity
11-19 20:39:27 D/RunUtil: operation failed, waiting for 500 ms
11-19 20:39:27 D/RunUtil: Running command null without timeout.
11-19 20:39:27 D/AppRunnerUtils: foregroundActivitySet(): [com.google.android.youtube.tv/com.google.android.apps.youtube.tv.activity.MainActivity]
11-19 20:39:28 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.google.android.apps.youtube.tv.activity.MainActivity.
11-19 20:39:28 D/AppRunnerUtils: waitUntilAppInForeground(): Checking activity: com.google.android.youtube.tv/com.google.android.apps.youtube.tv.activity.MainActivity
11-19 20:39:28 D/AppRunnerUtils: foregroundActivitySet(): [com.google.android.youtube.tv/com.google.android.apps.youtube.tv.activity.MainActivity]
11-19 20:39:29 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.google.android.apps.youtube.tv.activity.MainActivity.
11-19 20:39:44 D/ListInstrumentationParser: instrumentation:com.google.android.resolution.tvts/androidx.test.runner.AndroidJUnitRunner (target=com.google.android.resolution.tvts)
11-19 20:39:44 D/ListInstrumentationParser: instrumentation:com.google.android.rvis.tvts/androidx.test.runner.AndroidJUnitRunner (target=com.google.android.rvis.tvts)
11-19 20:39:44 D/ListInstrumentationParser: 
11-19 20:39:44 I/RemoteAndroidTestRunner: Running am instrument -w -r --abi armeabi-v7a  -e timeout_msec 20000 -e class 'com.google.android.rvis.tvts.TvtsRvisDeviceTest#sendAssistantKeycode' com.google.android.rvis.tvts/androidx.test.runner.AndroidJUnitRunner on foxconn-bfx_at100-SKBFXYZO0DTQ
11-19 20:39:46 I/BaseHostJUnit4Test: Test com.google.android.rvis.tvts.TvtsRvisDeviceTest#sendAssistantKeycode: PASSED
11-19 20:39:46 D/RunUtil: Running command [logcat -d | grep -E 'Read non-zero bytes'] without timeout.
11-19 20:39:47 I/TvtsRvisTest:                  15	               177
11-19 20:39:47 I/KeyCodeUtils: Sending keyevent KEYCODE_BACK - intValue: 4
11-19 20:39:47 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:39:48 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:39:48 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:39:48 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:39:48 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:39:48 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:39:49 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:39:49 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:39:49 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:39:49 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:39:49 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:39:50 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:39:50 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:39:50 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:39:50 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:39:50 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:39:51 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:39:51 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:39:51 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:39:51 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:39:51 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:39:52 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:39:52 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:39:52 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:39:52 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:39:52 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:39:53 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:39:53 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:39:53 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:39:53 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:39:54 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:39:54 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:39:54 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:39:54 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:39:54 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:39:55 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:39:55 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:39:55 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:39:55 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:39:55 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:39:55 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:39:56 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:39:56 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:39:56 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:39:56 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:39:56 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:39:57 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:39:57 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:39:57 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:39:57 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:39:58 E/TvtsRvisTest: AudioTrack was not paused or stopped
11-19 20:39:58 D/RunUtil: Running command null without timeout.
11-19 20:39:58 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, logcat, -c, -b, all] with timeout: 2m 0s
11-19 20:39:58 D/RunUtil: Running command null without timeout.
11-19 20:39:58 D/AppRunnerUtils: foregroundActivitySet(): [com.google.android.youtube.tv/com.google.android.apps.youtube.tv.activity.ShellActivity]
11-19 20:39:59 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.google.android.apps.youtube.tv.activity.ShellActivity.
11-19 20:39:59 D/AppRunnerUtils: waitUntilAppInForeground(): Checking activity: com.google.android.youtube.tv/com.google.android.apps.youtube.tv.activity.ShellActivity
11-19 20:39:59 D/RunUtil: operation failed, waiting for 500 ms
11-19 20:39:59 D/RunUtil: Running command null without timeout.
11-19 20:39:59 D/AppRunnerUtils: foregroundActivitySet(): [com.google.android.youtube.tv/com.google.android.apps.youtube.tv.activity.MainActivity]
11-19 20:40:00 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.google.android.apps.youtube.tv.activity.MainActivity.
11-19 20:40:00 D/AppRunnerUtils: waitUntilAppInForeground(): Checking activity: com.google.android.youtube.tv/com.google.android.apps.youtube.tv.activity.MainActivity
11-19 20:40:00 D/AppRunnerUtils: foregroundActivitySet(): [com.google.android.youtube.tv/com.google.android.apps.youtube.tv.activity.MainActivity]
11-19 20:40:01 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.google.android.apps.youtube.tv.activity.MainActivity.
11-19 20:40:16 D/ListInstrumentationParser: instrumentation:com.google.android.resolution.tvts/androidx.test.runner.AndroidJUnitRunner (target=com.google.android.resolution.tvts)
11-19 20:40:16 D/ListInstrumentationParser: instrumentation:com.google.android.rvis.tvts/androidx.test.runner.AndroidJUnitRunner (target=com.google.android.rvis.tvts)
11-19 20:40:16 D/ListInstrumentationParser: 
11-19 20:40:16 I/RemoteAndroidTestRunner: Running am instrument -w -r --abi armeabi-v7a  -e timeout_msec 20000 -e class 'com.google.android.rvis.tvts.TvtsRvisDeviceTest#sendAssistantKeycode' com.google.android.rvis.tvts/androidx.test.runner.AndroidJUnitRunner on foxconn-bfx_at100-SKBFXYZO0DTQ
11-19 20:40:18 I/BaseHostJUnit4Test: Test com.google.android.rvis.tvts.TvtsRvisDeviceTest#sendAssistantKeycode: PASSED
11-19 20:40:18 D/RunUtil: Running command [logcat -d | grep -E 'Read non-zero bytes'] without timeout.
11-19 20:40:19 I/TvtsRvisTest:                  11	               184
11-19 20:40:19 I/KeyCodeUtils: Sending keyevent KEYCODE_BACK - intValue: 4
11-19 20:40:19 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:40:19 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:40:20 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:40:20 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:40:20 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:40:20 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:40:20 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:40:21 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:40:21 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:40:21 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:40:21 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:40:21 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:40:22 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:40:22 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:40:22 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:40:22 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:40:22 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:40:23 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:40:23 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:40:23 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:40:23 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:40:23 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:40:24 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:40:24 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:40:24 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:40:24 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:40:24 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:40:24 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:40:25 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:40:25 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:40:25 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:40:25 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:40:25 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:40:26 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:40:26 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:40:26 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:40:26 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:40:26 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:40:27 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:40:27 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:40:27 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:40:27 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:40:28 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:40:28 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:40:28 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:40:28 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:40:28 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:40:29 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:40:29 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:40:29 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:40:29 E/TvtsRvisTest: AudioTrack was not paused or stopped
11-19 20:40:29 D/RunUtil: Running command null without timeout.
11-19 20:40:29 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, logcat, -c, -b, all] with timeout: 2m 0s
11-19 20:40:29 D/RunUtil: Running command null without timeout.
11-19 20:40:29 D/AppRunnerUtils: foregroundActivitySet(): [com.google.android.youtube.tv/com.google.android.apps.youtube.tv.activity.ShellActivity]
11-19 20:40:30 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.google.android.apps.youtube.tv.activity.ShellActivity.
11-19 20:40:30 D/AppRunnerUtils: waitUntilAppInForeground(): Checking activity: com.google.android.youtube.tv/com.google.android.apps.youtube.tv.activity.ShellActivity
11-19 20:40:30 D/RunUtil: operation failed, waiting for 500 ms
11-19 20:40:31 D/RunUtil: Running command null without timeout.
11-19 20:40:31 D/AppRunnerUtils: foregroundActivitySet(): [com.google.android.youtube.tv/com.google.android.apps.youtube.tv.activity.MainActivity]
11-19 20:40:31 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.google.android.apps.youtube.tv.activity.MainActivity.
11-19 20:40:31 D/AppRunnerUtils: waitUntilAppInForeground(): Checking activity: com.google.android.youtube.tv/com.google.android.apps.youtube.tv.activity.MainActivity
11-19 20:40:32 D/AppRunnerUtils: foregroundActivitySet(): [com.google.android.youtube.tv/com.google.android.apps.youtube.tv.activity.MainActivity]
11-19 20:40:32 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.google.android.apps.youtube.tv.activity.MainActivity.
11-19 20:40:47 D/ListInstrumentationParser: instrumentation:com.google.android.resolution.tvts/androidx.test.runner.AndroidJUnitRunner (target=com.google.android.resolution.tvts)
11-19 20:40:47 D/ListInstrumentationParser: instrumentation:com.google.android.rvis.tvts/androidx.test.runner.AndroidJUnitRunner (target=com.google.android.rvis.tvts)
11-19 20:40:47 D/ListInstrumentationParser: 
11-19 20:40:47 I/RemoteAndroidTestRunner: Running am instrument -w -r --abi armeabi-v7a  -e timeout_msec 20000 -e class 'com.google.android.rvis.tvts.TvtsRvisDeviceTest#sendAssistantKeycode' com.google.android.rvis.tvts/androidx.test.runner.AndroidJUnitRunner on foxconn-bfx_at100-SKBFXYZO0DTQ
11-19 20:40:50 I/BaseHostJUnit4Test: Test com.google.android.rvis.tvts.TvtsRvisDeviceTest#sendAssistantKeycode: PASSED
11-19 20:40:50 D/RunUtil: Running command [logcat -d | grep -E 'Read non-zero bytes'] without timeout.
11-19 20:40:50 I/TvtsRvisTest:                  11	               187
11-19 20:40:50 I/KeyCodeUtils: Sending keyevent KEYCODE_BACK - intValue: 4
11-19 20:40:51 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:40:51 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:40:51 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:40:51 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:40:52 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:40:52 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:40:52 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:40:52 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:40:52 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:40:53 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:40:53 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:40:53 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:40:53 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:40:53 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:40:54 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:40:54 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:40:54 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:40:54 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:40:55 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:40:55 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:40:55 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:40:55 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:40:55 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:40:56 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:40:56 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:40:56 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:40:56 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:40:56 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:40:57 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:40:57 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:40:57 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:40:57 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:40:57 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:40:58 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:40:58 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:40:58 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:40:58 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:40:58 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:40:59 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:40:59 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:40:59 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:40:59 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:40:59 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:41:00 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:41:00 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:41:00 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:41:00 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:41:00 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:41:01 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:41:01 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:41:01 E/TvtsRvisTest: AudioTrack was not paused or stopped
11-19 20:41:01 D/RunUtil: Running command null without timeout.
11-19 20:41:01 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, logcat, -c, -b, all] with timeout: 2m 0s
11-19 20:41:01 D/RunUtil: Running command null without timeout.
11-19 20:41:01 D/AppRunnerUtils: foregroundActivitySet(): [com.google.android.youtube.tv/com.google.android.apps.youtube.tv.activity.ShellActivity]
11-19 20:41:02 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.google.android.apps.youtube.tv.activity.ShellActivity.
11-19 20:41:02 D/AppRunnerUtils: waitUntilAppInForeground(): Checking activity: com.google.android.youtube.tv/com.google.android.apps.youtube.tv.activity.ShellActivity
11-19 20:41:02 D/RunUtil: operation failed, waiting for 500 ms
11-19 20:41:03 D/RunUtil: Running command null without timeout.
11-19 20:41:03 D/AppRunnerUtils: foregroundActivitySet(): [com.google.android.youtube.tv/com.google.android.apps.youtube.tv.activity.MainActivity]
11-19 20:41:03 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.google.android.apps.youtube.tv.activity.MainActivity.
11-19 20:41:03 D/AppRunnerUtils: waitUntilAppInForeground(): Checking activity: com.google.android.youtube.tv/com.google.android.apps.youtube.tv.activity.MainActivity
11-19 20:41:03 D/AppRunnerUtils: foregroundActivitySet(): [com.google.android.youtube.tv/com.google.android.apps.youtube.tv.activity.MainActivity]
11-19 20:41:04 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.google.android.apps.youtube.tv.activity.MainActivity.
11-19 20:41:19 D/ListInstrumentationParser: instrumentation:com.google.android.resolution.tvts/androidx.test.runner.AndroidJUnitRunner (target=com.google.android.resolution.tvts)
11-19 20:41:19 D/ListInstrumentationParser: instrumentation:com.google.android.rvis.tvts/androidx.test.runner.AndroidJUnitRunner (target=com.google.android.rvis.tvts)
11-19 20:41:19 D/ListInstrumentationParser: 
11-19 20:41:19 I/RemoteAndroidTestRunner: Running am instrument -w -r --abi armeabi-v7a  -e timeout_msec 20000 -e class 'com.google.android.rvis.tvts.TvtsRvisDeviceTest#sendAssistantKeycode' com.google.android.rvis.tvts/androidx.test.runner.AndroidJUnitRunner on foxconn-bfx_at100-SKBFXYZO0DTQ
11-19 20:41:22 I/BaseHostJUnit4Test: Test com.google.android.rvis.tvts.TvtsRvisDeviceTest#sendAssistantKeycode: PASSED
11-19 20:41:22 D/RunUtil: Running command [logcat -d | grep -E 'Read non-zero bytes'] without timeout.
11-19 20:41:22 I/TvtsRvisTest:                   9	               206
11-19 20:41:22 I/KeyCodeUtils: Sending keyevent KEYCODE_BACK - intValue: 4
11-19 20:41:23 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:41:23 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:41:23 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:41:23 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:41:23 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:41:24 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:41:24 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:41:24 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:41:24 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:41:24 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:41:25 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:41:25 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:41:25 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:41:25 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:41:25 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:41:26 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:41:26 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:41:26 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:41:26 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:41:26 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:41:27 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:41:27 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:41:27 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:41:27 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:41:27 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:41:28 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:41:28 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:41:28 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:41:28 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:41:28 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:41:29 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:41:29 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:41:29 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:41:29 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:41:30 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:41:30 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:41:30 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:41:30 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:41:30 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:41:31 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:41:31 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:41:31 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:41:31 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:41:31 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:41:32 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:41:32 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:41:32 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:41:32 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:41:32 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:41:33 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:41:33 E/TvtsRvisTest: AudioTrack was not paused or stopped
11-19 20:41:33 I/TvtsRvisTest: numOfRuns: 10
11-19 20:41:33 I/TvtsRvisTest: numOfFailedRuns: 0
11-19 20:41:33 D/PrettyTestEventLogger: 
==================== com.google.android.rvis.tvts.TvtsRvisTest#testHeavyUsageRvisLaunchLegacyAudioRecord ENDED: Tue Nov 19 20:41:33 KST 2024 ====================
